[04/29 10:40:58,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Click 'AddNewDealSpan'"...
[04/29 10:41:00,ArtOfTest.Runner.exe(11160:9),DialogPlayback] DialogMonitor.AutomationEvent_OnStructureChanged() : ChildAdded event sent by named element "ProLink - Add Deal".
[04/29 10:41:00,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Click 'AddNewDealSpan'".
[04/29 10:41:00,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:00,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:00,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:00,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:00,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:00,ArtOfTest.Runner.exe(11160:9),DialogPlayback] DialogMonitor.AutomationEvent_OnWindowOpened() : New dialog detected (Text = "1 Reminder", ClassName = "#32770").
[04/29 10:41:00,ArtOfTest.Runner.exe(11160:9),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[04/29 10:41:00,ArtOfTest.Runner.exe(11160:9),DialogPlayback] DialogMonitor.HandleUnexpectedDialog() : Unexpected dialog encountered. Taking no action.
[04/29 10:41:00,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "Enter text 'DealNameLF' in 'CphMainCphBodyTcMainTpMainTxtDealNameText' - DataDriven: [$(DealNameLF)]"...
[04/29 10:41:01,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "Enter text 'DealNameLF' in 'CphMainCphBodyTcMainTpMainTxtDealNameText' - DataDriven: [$(DealNameLF)]".
[04/29 10:41:01,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:01,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:01,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:01,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:01,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:01,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 9 "Click 'CphMainCphBodyTcMainTpMainTdBlank4TableCell'"...
[04/29 10:41:01,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 9 "Click 'CphMainCphBodyTcMainTpMainTdBlank4TableCell'".
[04/29 10:41:01,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:01,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:01,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:01,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:01,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:01,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 10 "RadInput('ctl00_ctl00_cphMain_cphBody_tcMain_tpMain_rdpProjectedCloseDate_dateInput_wrapper'): value '12/31/2013' entered."...
[04/29 10:41:03,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 10 "RadInput('ctl00_ctl00_cphMain_cphBody_tcMain_tpMain_rdpProjectedCloseDate_dateInput_wrapper'): value '12/31/2013' entered.".
[04/29 10:41:03,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:03,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:03,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:03,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:03,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:03,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 11 "Select 'ByValue' option '2' on 'CphMainCphBodyTcMainTpMainDdlBusinessUnitSelect'"...
[04/29 10:41:04,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 11 "Select 'ByValue' option '2' on 'CphMainCphBodyTcMainTpMainDdlBusinessUnitSelect'".
[04/29 10:41:04,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:04,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:04,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:04,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:04,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:04,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 12 "Select 'ByValue' option '43' on 'CphMainCphBodyTcMainTpMainDdlStateSelect'"...
[04/29 10:41:05,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 12 "Select 'ByValue' option '43' on 'CphMainCphBodyTcMainTpMainDdlStateSelect'".
[04/29 10:41:05,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:05,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:05,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:05,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:05,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:05,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 13 "Enter text 'Nashville' in 'CphMainCphBodyTcMainTpMainTxtCityText'"...
[04/29 10:41:05,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 13 "Enter text 'Nashville' in 'CphMainCphBodyTcMainTpMainTxtCityText'".
[04/29 10:41:05,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:05,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:05,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:05,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:05,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:05,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 14 "Select 'ByValue' option '2533' on 'CphMainCphBodyTcMainTpMainDdlCountySelect'"...
[04/29 10:41:06,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 14 "Select 'ByValue' option '2533' on 'CphMainCphBodyTcMainTpMainDdlCountySelect'".
[04/29 10:41:06,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:06,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:06,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:06,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:06,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:06,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 15 "Click 'CphMainCphBodyTcMainTpMainProLinkLabel16Span'"...
[04/29 10:41:07,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 15 "Click 'CphMainCphBodyTcMainTpMainProLinkLabel16Span'".
[04/29 10:41:07,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:07,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:07,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:07,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:07,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:07,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 16 "Check 'CphMainCphBodyTcMainTpMainCbLIHTCFederalCheckBox' to be 'True'"...
[04/29 10:41:07,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 16 "Check 'CphMainCphBodyTcMainTpMainCbLIHTCFederalCheckBox' to be 'True'".
[04/29 10:41:07,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:07,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:07,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:07,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:07,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:07,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 17 "Check 'CphMainCphBodyTcMainTpMainCbLIHTCStateCheckBox' to be 'True'"...
[04/29 10:41:08,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 17 "Check 'CphMainCphBodyTcMainTpMainCbLIHTCStateCheckBox' to be 'True'".
[04/29 10:41:08,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:08,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:08,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:08,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:08,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:08,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 18 "Click 'CphMainCphBodyTcMainTpMainBtnUpdateTaxCreditSectionsSubmit'"...
[04/29 10:41:10,ArtOfTest.Runner.exe(11160:9),DialogPlayback] DialogMonitor.AutomationEvent_OnStructureChanged() : ChildAdded event sent by named element "ProLink - Add Deal".
[04/29 10:41:10,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 18 "Click 'CphMainCphBodyTcMainTpMainBtnUpdateTaxCreditSectionsSubmit'".
[04/29 10:41:10,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:10,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:10,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:10,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:10,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:10,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 19 "Select 'ByValue' option '2' on 'CphMainCphBodyTcMainTpMainWcEditLihtcDealDetailsFederalDdlTypeSelect'"...
[04/29 10:41:11,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 19 "Select 'ByValue' option '2' on 'CphMainCphBodyTcMainTpMainWcEditLihtcDealDetailsFederalDdlTypeSelect'".
[04/29 10:41:11,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:11,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:11,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:11,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:11,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:11,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 20 "RadInput('ctl00_ctl00_cphMain_cphBody_tcMain_tpMain_wcEditLihtcDealDetailsFederal_txtTotalCredits_wrapper'): value '15000000' entered."...
[04/29 10:41:13,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 20 "RadInput('ctl00_ctl00_cphMain_cphBody_tcMain_tpMain_wcEditLihtcDealDetailsFederal_txtTotalCredits_wrapper'): value '15000000' entered.".
[04/29 10:41:13,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:13,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:13,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:13,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:13,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:13,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 21 "Click 'NbspTableCell'"...
[04/29 10:41:13,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 21 "Click 'NbspTableCell'".
[04/29 10:41:13,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:13,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:13,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:13,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:13,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:13,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 22 "RadInput('ctl00_ctl00_cphMain_cphBody_tcMain_tpMain_wcEditLihtcDealDetailsFederal_txtPricePerCredit_wrapper'): value '.75' entered."...
[04/29 10:41:14,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 22 "RadInput('ctl00_ctl00_cphMain_cphBody_tcMain_tpMain_wcEditLihtcDealDetailsFederal_txtPricePerCredit_wrapper'): value '.75' entered.".
[04/29 10:41:14,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:14,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:14,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:14,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:14,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:14,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 23 "Select 'ByValue' option '2' on 'CphMainCphBodyTcMainTpMainWcEditLihtcDealDetailsStateDdlTypeSelect'"...
[04/29 10:41:15,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 23 "Select 'ByValue' option '2' on 'CphMainCphBodyTcMainTpMainWcEditLihtcDealDetailsStateDdlTypeSelect'".
[04/29 10:41:15,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:15,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:15,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:15,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:15,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:15,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 24 "RadInput('ctl00_ctl00_cphMain_cphBody_tcMain_tpMain_wcEditLihtcDealDetailsState_txtTotalCredits_wrapper'): value '750000' entered."...
[04/29 10:41:16,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 24 "RadInput('ctl00_ctl00_cphMain_cphBody_tcMain_tpMain_wcEditLihtcDealDetailsState_txtTotalCredits_wrapper'): value '750000' entered.".
[04/29 10:41:16,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:16,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:16,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:16,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:16,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:17,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 25 "RadInput('ctl00_ctl00_cphMain_cphBody_tcMain_tpMain_wcEditLihtcDealDetailsState_txtUsbPercent_wrapper'): value '100' entered."...
[04/29 10:41:18,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 25 "RadInput('ctl00_ctl00_cphMain_cphBody_tcMain_tpMain_wcEditLihtcDealDetailsState_txtUsbPercent_wrapper'): value '100' entered.".
[04/29 10:41:18,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:18,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:18,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:18,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:18,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:18,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 26 "RadInput('ctl00_ctl00_cphMain_cphBody_tcMain_tpMain_wcEditLihtcDealDetailsState_txtPricePerCredit_wrapper'): value '.85' entered."...
[04/29 10:41:19,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 26 "RadInput('ctl00_ctl00_cphMain_cphBody_tcMain_tpMain_wcEditLihtcDealDetailsState_txtPricePerCredit_wrapper'): value '.85' entered.".
[04/29 10:41:19,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:19,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:19,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:19,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:19,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:19,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 27 "Click 'TableCell1'"...
[04/29 10:41:19,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 27 "Click 'TableCell1'".
[04/29 10:41:19,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:19,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:19,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:19,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:19,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:19,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 28 "Enter text 'these are notes for a LIHTC Fund Deal' in 'CphMainCphBodyTcMainTpMainTxtNotesTextArea'"...
[04/29 10:41:20,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 28 "Enter text 'these are notes for a LIHTC Fund Deal' in 'CphMainCphBodyTcMainTpMainTxtNotesTextArea'".
[04/29 10:41:20,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:20,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:20,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:20,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:20,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:20,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 29 "Click 'SaveSpan'"...
[04/29 10:41:23,ArtOfTest.Runner.exe(11160:9),DialogPlayback] DialogMonitor.AutomationEvent_OnStructureChanged() : ChildAdded event sent by named element "ProLink - Add Deal".
[04/29 10:41:23,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 29 "Click 'SaveSpan'".
[04/29 10:41:23,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:23,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:23,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[04/29 10:41:23,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:23,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:53,ArtOfTest.Runner.exe(11160:6),Error] ExecutionEngine.ExecuteStep() : EXCEPTION! (see below)
     Outer Exception Type: ArtOfTest.Common.Design.Exceptions.ElementFindException
     Message: Unable to locate element. Details: Attempting to find [Html] element using
          Find logic
          (Html): [id 'Exact' ctl00_ctl00_cphMain_cphBody_lblDealId] AND [tagname 'Exact' span]
          Unable to locate element. Search failed!
          Learn more about finding elements here:
          http://www.telerik.com/automated-testing-tools/support/documentation/user-guide/troubleshooting_guide/test-execution-problems/unable-to-locate-element.aspx
     HRESULT: 0x80131500 (Official ID (if app.) = COR_E_EXCEPTION, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5376)
     Call Stack:
          at ArtOfTest.WebAii.Design.Execution.ExecutionUtils.WaitForAllElements(IAutomationHost host, AutomationDescriptor descriptor, Int32 timeout)
          at ArtOfTest.WebAii.Design.Execution.ExecutionEngine.ExecuteStep(Int32 order)

[04/29 10:41:53,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:53,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::BreakOnFail
[04/29 10:41:53,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:53,ArtOfTest.Runner.exe(11160:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Execute test 'Add Deal - LIHTC Fund'".
[04/29 10:41:53,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[04/29 10:41:53,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::BreakOnFail
[04/29 10:41:53,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[04/29 10:41:54,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[04/29 10:41:54,ArtOfTest.Runner.exe(11160:6),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::ShutDown::Start
[04/29 10:41:54,ArtOfTest.Runner.exe(11160:6),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DetachWrappedWindow::Start
[04/29 10:41:54,ArtOfTest.Runner.exe(11160:6),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DetachWrappedWindow::End
[04/29 10:41:54,ArtOfTest.Runner.exe(11160:6),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DispatcherBeginInvokeShutdown::Start
[04/29 10:41:54,ArtOfTest.Runner.exe(11160:6),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::Close::Start
[04/29 10:41:54,ArtOfTest.Runner.exe(11160:6),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[04/29 10:41:54,ArtOfTest.Runner.exe(11160:6),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-1612162618" in order to unblock and eventually terminate it...
[04/29 10:41:54,ArtOfTest.Runner.exe(11160:6),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[04/29 10:41:54,ArtOfTest.Runner.exe(11160:8),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[04/29 10:41:54,ArtOfTest.Runner.exe(11160:6),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[04/29 10:41:54,ArtOfTest.Runner.exe(11160:6),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[04/29 10:41:54,ArtOfTest.Runner.exe(11160:6),Framework] Manager.RemoveBrowser() : Removed client ID "Client_711f9245-62d6-4eb1-bc21-363692e9d02f" (0 remaining).
[04/29 10:41:54,ArtOfTest.Runner.exe(11160:6),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[04/29 10:41:54,ArtOfTest.Runner.exe(11160:6),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 15).
[04/29 10:41:54,ArtOfTest.Runner.exe(11160:15),Warning] PipeCommunication.AsyncPipeRead() : Pipe read wait broken by thread-abort.
[04/29 10:42:08,ArtOfTest.Runner.exe(11160:9),UIAutomation] AutomationExtensions.RemoveStructureChangedEventHandler() : Removed the structure change handler on element System.Windows.Automation.AutomationElement scope Subtree.
[04/29 10:42:09,ArtOfTest.Runner.exe(11160:9),DialogPlayback] DialogMonitor.AutomationEvent_OnWindowOpened() : New dialog detected (Text = "Windows Internet Explorer", ClassName = "#32770").
[04/29 10:42:09,ArtOfTest.Runner.exe(11160:9),DialogPlayback] DialogMonitor.HandleDialog() : OnBeforeUnloadDialog(Dismiss=OK) detected, now attempting to handle it.
[04/29 10:42:09,ArtOfTest.Runner.exe(11160:9),Window] Window.WaitForStable() : Window "Windows Internet Explorer" bounds stabilized at "{X=649,Y=357,Width=366,Height=236}".
[04/29 10:42:09,iexplore.exe(9476:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_711f9245-62d6-4eb1-bc21-363692e9d02f") to pipe server...
[04/29 10:42:09,iexplore.exe(9476:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[04/29 10:42:09,ArtOfTest.Runner.exe(11160:9),DialogPlayback] DialogMonitor.HandleDialog() : OnBeforeUnloadDialog(Dismiss=OK) handled.
[04/29 10:42:10,ArtOfTest.Runner.exe(11160:9),DialogPlayback] DialogMonitor.AutomationEvent_OnWindowOpened() : New window detected but the HWND is invalid (9770C9C).
[04/29 10:42:10,ArtOfTest.Runner.exe(11160:6),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[04/29 10:42:11,Telerik.TestStudio.exe(10216:34)] First trace message from background thread "Callback Listener Thread" (managed ID = 34, native ID = 10776).
[04/29 10:42:11,Telerik.TestStudio.exe(10216:34),Warning] PipeCommunication.AsyncPipeRead() : Pipe was already disposed at the moment of the wait attempt.
[04/29 10:48:07,Telerik.TestStudio.exe(10216:30)] First trace message from foreground unnamed thread (managed ID = 30, native ID = 4552).
[04/29 10:48:07,Telerik.TestStudio.exe(10216:30),TestStudio] StandaloneHost.LoadResults() : ------- Function Start -------
[04/29 10:48:07,Telerik.TestStudio.exe(10216:30),Uncategorized] MainWindow.LogMessage() : Partially Loading '0' results..
[04/29 10:48:07,Telerik.TestStudio.exe(10216:30),Uncategorized] MainWindow.LogMessage() : Results are loaded!
[04/29 10:48:07,Telerik.TestStudio.exe(10216:30),TestStudio] StandaloneHost.LoadResults() : ------- Function End -------
